[INFO] cloning repository https://github.com/pedrodelgallego/strawman-rust
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/pedrodelgallego/strawman-rust" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fpedrodelgallego%2Fstrawman-rust", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fpedrodelgallego%2Fstrawman-rust'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] c8e687b3cd70e5ae94623190a0e10b3a8486b51f
[INFO] testing pedrodelgallego/strawman-rust against try#b8e88e5ddf5521a9f43ee3f62a702388c713e4bb for pr-155114
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fpedrodelgallego%2Fstrawman-rust" "/workspace/builds/worker-3-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/pedrodelgallego/strawman-rust
[INFO] finished tweaking git repo https://github.com/pedrodelgallego/strawman-rust
[INFO] tweaked toml for git repo https://github.com/pedrodelgallego/strawman-rust written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/pedrodelgallego/strawman-rust on toolchain b8e88e5ddf5521a9f43ee3f62a702388c713e4bb
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/pedrodelgallego/strawman-rust already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 5b7513016d492c551cbb2f88e0258b3e16e03899bbb321ac0edc9be7c88fb067
[INFO] running `Command { std: "docker" "start" "-a" "5b7513016d492c551cbb2f88e0258b3e16e03899bbb321ac0edc9be7c88fb067", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "5b7513016d492c551cbb2f88e0258b3e16e03899bbb321ac0edc9be7c88fb067", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5b7513016d492c551cbb2f88e0258b3e16e03899bbb321ac0edc9be7c88fb067", kill_on_drop: false }`
[INFO] [stdout] 5b7513016d492c551cbb2f88e0258b3e16e03899bbb321ac0edc9be7c88fb067
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] bf4d0fbe1c20f1e957da494eda16d5783b00981c7ec634b023a5e0311574a89a
[INFO] running `Command { std: "docker" "start" "-a" "bf4d0fbe1c20f1e957da494eda16d5783b00981c7ec634b023a5e0311574a89a", kill_on_drop: false }`
[INFO] [stderr]    Compiling strawman v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: constant `BENCH_STACK_SIZE` is never used
[INFO] [stdout]   --> src/bench.rs:52:7
[INFO] [stdout]    |
[INFO] [stdout] 52 | const BENCH_STACK_SIZE: usize = 128 * 1024 * 1024;
[INFO] [stdout]    |       ^^^^^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1.87s
[INFO] running `Command { std: "docker" "inspect" "bf4d0fbe1c20f1e957da494eda16d5783b00981c7ec634b023a5e0311574a89a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bf4d0fbe1c20f1e957da494eda16d5783b00981c7ec634b023a5e0311574a89a", kill_on_drop: false }`
[INFO] [stdout] bf4d0fbe1c20f1e957da494eda16d5783b00981c7ec634b023a5e0311574a89a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a10e41072485287e391ba7f3c37f2845f2808caec4fbdea1b68d8f52cdf77ba2
[INFO] running `Command { std: "docker" "start" "-a" "a10e41072485287e391ba7f3c37f2845f2808caec4fbdea1b68d8f52cdf77ba2", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling unicode-ident v1.0.22
[INFO] [stderr]    Compiling libc v0.2.180
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling rustix v1.1.3
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling linux-raw-sys v0.11.0
[INFO] [stderr]    Compiling bitflags v2.10.0
[INFO] [stderr]    Compiling cfg-if v1.0.4
[INFO] [stderr]    Compiling once_cell v1.21.3
[INFO] [stderr]    Compiling fastrand v2.3.0
[INFO] [stdout] warning: constant `BENCH_STACK_SIZE` is never used
[INFO] [stdout]   --> src/bench.rs:52:7
[INFO] [stdout]    |
[INFO] [stdout] 52 | const BENCH_STACK_SIZE: usize = 128 * 1024 * 1024;
[INFO] [stdout]    |       ^^^^^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]    Compiling syn v2.0.114
[INFO] [stderr]    Compiling tempfile v3.24.0
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling filedescriptor v0.8.3
[INFO] [stderr]    Compiling gag v1.0.0
[INFO] [stderr]    Compiling strawman v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: constant `BENCH_STACK_SIZE` is never used
[INFO] [stdout]   --> src/bench.rs:52:7
[INFO] [stdout]    |
[INFO] [stdout] 52 | const BENCH_STACK_SIZE: usize = 128 * 1024 * 1024;
[INFO] [stdout]    |       ^^^^^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused variable: `env`
[INFO] [stdout]    --> tests/test_fast_eval.rs:259:9
[INFO] [stdout]     |
[INFO] [stdout] 259 |     let env = make_test_env();
[INFO] [stdout]     |         ^^^ help: if this is intentional, prefix it with an underscore: `_env`
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(unused_variables)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused variable: `env`
[INFO] [stdout]    --> tests/test_fast_eval.rs:349:9
[INFO] [stdout]     |
[INFO] [stdout] 349 |     let env = default_env();
[INFO] [stdout]     |         ^^^ help: if this is intentional, prefix it with an underscore: `_env`
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 15.37s
[INFO] running `Command { std: "docker" "inspect" "a10e41072485287e391ba7f3c37f2845f2808caec4fbdea1b68d8f52cdf77ba2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a10e41072485287e391ba7f3c37f2845f2808caec4fbdea1b68d8f52cdf77ba2", kill_on_drop: false }`
[INFO] [stdout] a10e41072485287e391ba7f3c37f2845f2808caec4fbdea1b68d8f52cdf77ba2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 21ff99163265d730f3a013a6f4355a093e00a4040753b583cedd3465e340e13a
[INFO] running `Command { std: "docker" "start" "-a" "21ff99163265d730f3a013a6f4355a093e00a4040753b583cedd3465e340e13a", kill_on_drop: false }`
[INFO] [stderr] warning: constant `BENCH_STACK_SIZE` is never used
[INFO] [stderr]   --> src/bench.rs:52:7
[INFO] [stderr]    |
[INFO] [stderr] 52 | const BENCH_STACK_SIZE: usize = 128 * 1024 * 1024;
[INFO] [stderr]    |       ^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `strawman` (lib) generated 1 warning
[INFO] [stderr] warning: unused variable: `env`
[INFO] [stderr]    --> tests/test_fast_eval.rs:259:9
[INFO] [stderr]     |
[INFO] [stderr] 259 |     let env = make_test_env();
[INFO] [stderr]     |         ^^^ help: if this is intentional, prefix it with an underscore: `_env`
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(unused_variables)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: unused variable: `env`
[INFO] [stderr]    --> tests/test_fast_eval.rs:349:9
[INFO] [stderr]     |
[INFO] [stderr] 349 |     let env = default_env();
[INFO] [stderr]     |         ^^^ help: if this is intentional, prefix it with an underscore: `_env`
[INFO] [stderr] 
[INFO] [stderr] warning: `strawman` (test "test_fast_eval") generated 2 warnings (run `cargo fix --test "test_fast_eval" -p strawman` to apply 2 suggestions)
[INFO] [stderr] warning: `strawman` (lib test) generated 1 warning (1 duplicate)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.12s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/strawman-a876671561a53793)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/strawman-4c4967fe35146605)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_bench.rs (/opt/rustwide/target/debug/deps/test_bench-e764b38fb5fafac6)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test benchmark_factorial_correctness has been running for over 60 seconds
[INFO] [stdout] test benchmark_harness_runs_all_programs has been running for over 60 seconds
[INFO] [stdout] test benchmark_table_output has been running for over 60 seconds
[INFO] [stdout] test run_bench_to_writer_prints_comparison_table has been running for over 60 seconds
[INFO] [stdout] test benchmark_table_output ... ok
[INFO] [stdout] test run_bench_to_writer_prints_comparison_table ... ok
[INFO] [stdout] test benchmark_harness_runs_all_programs ... ok
[INFO] [stdout] test benchmark_factorial_correctness ... ok
[INFO] [stderr]      Running tests/test_bench_smoke.rs (/opt/rustwide/target/debug/deps/test_bench_smoke-9e2ad12d176b293a)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 69.50s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test smoke_ack_3_6_naive ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 12.71s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_builtins.rs (/opt/rustwide/target/debug/deps/test_builtins-840e1c26d18805c9)
[INFO] [stdout] 
[INFO] [stdout] running 66 tests
[INFO] [stdout] test e1_11_div_zero ... ok
[INFO] [stdout] test e1_11_add_floats ... ok
[INFO] [stdout] test e1_11_div_float ... ok
[INFO] [stdout] test e1_11_add_one ... ok
[INFO] [stdout] test e1_11_mod_zero ... ok
[INFO] [stdout] test e1_11_add_many ... ok
[INFO] [stdout] test e1_11_non_number ... ok
[INFO] [stdout] test e1_11_div_int ... ok
[INFO] [stdout] test e1_11_mod ... ok
[INFO] [stdout] test e1_11_add_zero_args ... ok
[INFO] [stdout] test e1_11_sub_two ... ok
[INFO] [stdout] test e1_11_sub_negate ... ok
[INFO] [stdout] test e1_11_sub_many ... ok
[INFO] [stdout] test e1_12_equal_diff ... ok
[INFO] [stdout] test e1_12_equal_atoms ... ok
[INFO] [stdout] test e1_11_mul_zero_args ... ok
[INFO] [stdout] test e1_11_mul ... ok
[INFO] [stdout] test e1_12_equal_lists ... ok
[INFO] [stdout] test e1_12_equal_strings ... ok
[INFO] [stdout] test e1_12_geq_false ... ok
[INFO] [stdout] test e1_12_less_false ... ok
[INFO] [stdout] test e1_12_less_true ... ok
[INFO] [stdout] test e1_12_less_equal_args ... ok
[INFO] [stdout] test e1_12_num_neq ... ok
[INFO] [stdout] test e1_13_car ... ok
[INFO] [stdout] test e1_13_car_cdr_composition ... ok
[INFO] [stdout] test e1_13_car_of_cons ... ok
[INFO] [stdout] test e1_13_cdr ... ok
[INFO] [stdout] test e1_12_greater ... ok
[INFO] [stdout] test e1_12_non_number_comparison_error ... ok
[INFO] [stdout] test e1_13_cdr_of_cons ... ok
[INFO] [stdout] test e1_13_cons_dotted ... ok
[INFO] [stdout] test e1_13_cons_onto_list ... ok
[INFO] [stdout] test e1_13_list_empty ... ok
[INFO] [stdout] test e1_13_list_many ... ok
[INFO] [stdout] test e1_13_null_empty ... ok
[INFO] [stdout] test e1_13_null_non_empty ... ok
[INFO] [stdout] test e1_13_car_of_empty_errors ... ok
[INFO] [stdout] test e1_13_cdr_of_atom_errors ... ok
[INFO] [stdout] test e1_13_car_of_atom_errors ... ok
[INFO] [stdout] test e1_12_leq_true ... ok
[INFO] [stdout] test e1_13_pair_empty ... ok
[INFO] [stdout] test e1_13_pair_pair ... ok
[INFO] [stdout] test e1_14_not_false ... ok
[INFO] [stdout] test e1_14_not_truthy ... ok
[INFO] [stdout] test e1_14_not_true ... ok
[INFO] [stdout] test e1_15_boolean_no ... ok
[INFO] [stdout] test e1_12_num_eq ... ok
[INFO] [stdout] test e1_15_boolean_yes ... ok
[INFO] [stdout] test e1_13_pair_atom ... ok
[INFO] [stdout] test e1_15_number_yes ... ok
[INFO] [stdout] test e1_15_procedure_builtin ... ok
[INFO] [stdout] test e1_15_procedure_car ... ok
[INFO] [stdout] test e1_15_procedure_lambda ... ok
[INFO] [stdout] test e1_12_equal_nested_lists ... ok
[INFO] [stdout] test e1_13_null_non_list ... ok
[INFO] [stdout] test e1_15_procedure_no ... ok
[INFO] [stdout] test e1_15_number_computed ... ok
[INFO] [stdout] test e1_15_number_no ... ok
[INFO] [stdout] test e1_15_symbol_no ... ok
[INFO] [stdout] test e1_15_symbol_yes ... ok
[INFO] [stdout] test e1_15_string_yes ... ok
[INFO] [stdout] test e1_15_string_no ... ok
[INFO] [stdout] test e1_15_newline ... ok
[INFO] [stdout] test e1_15_display_number ... ok
[INFO] [stdout] test e1_15_display_string ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 66 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_env.rs (/opt/rustwide/target/debug/deps/test_env-51c5357376e0a26b)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test shadowing ... ok
[INFO] [stderr]      Running tests/test_eval.rs (/opt/rustwide/target/debug/deps/test_eval-4a024e76aa11a5b9)
[INFO] [stdout] test extend_with_params_and_args ... ok
[INFO] [stdout] test update_unbound_errors ... ok
[INFO] [stdout] test parent_not_mutated_by_child ... ok
[INFO] [stdout] test update_existing_binding ... ok
[INFO] [stdout] test parent_chain_lookup ... ok
[INFO] [stdout] test set_and_lookup ... ok
[INFO] [stdout] test unbound_lookup_errors ... ok
[INFO] [stdout] test extend_arity_mismatch_errors ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 136 tests
[INFO] [stdout] test eval_begin_empty_returns_void ... ok
[INFO] [stdout] test eval_begin_nested ... ok
[INFO] [stdout] test eval_begin_three_exprs_returns_last ... ok
[INFO] [stdout] test eval_begin_single_expr ... ok
[INFO] [stdout] test eval_boolean_false_returns_itself ... ok
[INFO] [stdout] test eval_begin_side_effect_order ... ok
[INFO] [stdout] test eval_boolean_true_returns_itself ... ok
[INFO] [stdout] test eval_begin_two_exprs_returns_last ... ok
[INFO] [stdout] test eval_bound_symbol_returns_value ... ok
[INFO] [stdout] test eval_define_returns_void ... ok
[INFO] [stdout] test eval_define_expr ... ok
[INFO] [stdout] test eval_e1_10_closure_call ... ok
[INFO] [stdout] test eval_define_overwrites ... ok
[INFO] [stdout] test eval_e1_10_nested_call ... ok
[INFO] [stdout] test eval_e1_10_non_procedure_number ... ok
[INFO] [stdout] test eval_e1_10_builtin_call ... ok
[INFO] [stdout] test eval_e1_10_non_procedure_string ... ok
[INFO] [stdout] test eval_e1_10_higher_order ... ok
[INFO] [stdout] test eval_e1_14_or_empty ... ok
[INFO] [stdout] test eval_e1_14_or_all_false ... ok
[INFO] [stdout] test eval_e1_14_and_all_true ... ok
[INFO] [stdout] test eval_e1_14_or_first_true ... ok
[INFO] [stdout] test eval_e1_14_and_empty ... ok
[INFO] [stdout] test eval_e1_14_and_short_circuit ... ok
[INFO] [stdout] test eval_e1_14_and_one_false ... ok
[INFO] [stdout] test eval_e2_1_let_empty_bindings ... ok
[INFO] [stdout] test eval_e1_14_or_short_circuit ... ok
[INFO] [stdout] test eval_e2_1_let_body_implicit_begin ... ok
[INFO] [stdout] test eval_e2_1_let_parallel_semantics ... ok
[INFO] [stdout] test eval_e2_1_let_nested ... ok
[INFO] [stdout] test eval_e2_1_let_simple ... ok
[INFO] [stdout] test eval_e2_1_let_two_bindings ... ok
[INFO] [stdout] test eval_e2_2_let_star_sequential ... ok
[INFO] [stdout] test eval_e2_2_let_star_acceptance ... ok
[INFO] [stdout] test eval_e2_1_let_shadowing ... ok
[INFO] [stdout] test eval_e2_2_let_star_empty ... ok
[INFO] [stdout] test eval_e2_1_let_malformed_binding ... ok
[INFO] [stdout] test eval_e2_1_let_outer_unchanged ... ok
[INFO] [stdout] test eval_e2_2_let_star_shadow_across ... ok
[INFO] [stdout] test eval_e2_2_let_star_three_deps ... ok
[INFO] [stdout] test eval_e2_3_letrec_non_lambda_value ... ok
[INFO] [stdout] test eval_e2_4_define_shorthand_no_params ... ok
[INFO] [stdout] test eval_e2_4_define_shorthand_multi_param ... ok
[INFO] [stdout] test eval_e2_4_define_shorthand_acceptance ... ok
[INFO] [stdout] test eval_e2_4_define_shorthand_with_body ... ok
[INFO] [stdout] test eval_e2_3_letrec_mutual_recursion_even ... ok
[INFO] [stdout] test eval_e2_5_closure_scope_lexical ... ok
[INFO] [stdout] test eval_e2_4_define_shorthand_recursive ... ok
[INFO] [stdout] test eval_e2_3_letrec_self_recursive_factorial ... ok
[INFO] [stdout] test eval_e2_3_letrec_mutual_recursion_even_10 ... ok
[INFO] [stdout] test eval_e2_5_accumulator_intermediate_values ... ok
[INFO] [stdout] test eval_e2_4_define_shorthand_simple ... ok
[INFO] [stdout] test eval_e2_5_accumulator_mutable_closure ... ok
[INFO] [stdout] test eval_e2_5_factorial_0 ... ok
[INFO] [stdout] test eval_e2_5_factorial_10 ... ok
[INFO] [stdout] test eval_e3_2_callcc_early_exit ... ok
[INFO] [stdout] test eval_e3_2_callcc_in_expression ... ok
[INFO] [stdout] test eval_e3_2_callcc_non_procedure_error ... ok
[INFO] [stdout] test eval_e3_2_callcc_normal_return ... ok
[INFO] [stdout] test eval_e3_2_callcc_saved_continuation ... ok
[INFO] [stdout] test eval_e3_3_catch_nested ... ok
[INFO] [stdout] test eval_e3_3_catch_no_throw ... ok
[INFO] [stdout] test eval_e3_3_catch_simple_throw ... ok
[INFO] [stdout] test eval_e3_3_catch_throw_in_function ... ok
[INFO] [stdout] test eval_e3_3_catch_acceptance_criterion ... ok
[INFO] [stdout] test eval_e3_3_catch_wrong_tag ... ok
[INFO] [stdout] test eval_e3_1_identity_continuation ... ok
[INFO] [stdout] test eval_e3_2_callcc_acceptance_abandon_computation ... ok
[INFO] [stdout] test eval_e3_4_block_nested_return_outer ... ok
[INFO] [stdout] test eval_e3_2_callcc_acceptance_saved_resume ... ok
[INFO] [stdout] test eval_e2_5_map_with_lambda ... ok
[INFO] [stdout] test eval_e3_4_block_acceptance_criterion ... ok
[INFO] [stdout] test eval_e2_5_filter_with_lambda ... ok
[INFO] [stdout] test eval_e3_4_block_early_return ... ok
[INFO] [stdout] test eval_e2_5_fibonacci_10 ... ok
[INFO] [stdout] test eval_e4_1_acceptance_shared_closure_mutation ... ok
[INFO] [stdout] test eval_e3_4_block_no_return ... ok
[INFO] [stdout] test eval_e3_4_block_return_from_inner ... ok
[INFO] [stdout] test eval_e4_1_lambda_captures_box ... ok
[INFO] [stdout] test eval_e3_4_return_from_unknown_block ... ok
[INFO] [stdout] test eval_e4_1_shared_mutation ... ok
[INFO] [stdout] test eval_e4_2_acceptance_set_car_pair_display ... ok
[INFO] [stdout] test eval_e4_2_set_car ... ok
[INFO] [stdout] test eval_e4_2_set_cdr ... ok
[INFO] [stdout] test eval_e4_2_non_pair_error ... ok
[INFO] [stdout] test eval_e4_3_eq_different_lists ... ok
[INFO] [stdout] test eval_e4_3_acceptance_eq_vs_equal ... ok
[INFO] [stdout] test eval_e4_3_eq_same_number ... ok
[INFO] [stdout] test eval_e4_3_eq_same_binding ... ok
[INFO] [stdout] test eval_e4_3_eq_same_symbol ... ok
[INFO] [stdout] test eval_e4_3_equal_lists ... ok
[INFO] [stdout] test eval_e4_3_equal_nested ... ok
[INFO] [stdout] test eval_e4_4_acceptance_set_and_ref ... ok
[INFO] [stdout] test eval_e4_3_equal_different ... ok
[INFO] [stdout] test eval_e4_4_length ... ok
[INFO] [stdout] test eval_e4_4_make_and_ref ... ok
[INFO] [stdout] test eval_e4_4_type_pred_false ... ok
[INFO] [stdout] test eval_e4_4_type_pred_true ... ok
[INFO] [stdout] test eval_e4_4_set_and_ref ... ok
[INFO] [stdout] test eval_e4_4_out_of_bounds ... ok
[INFO] [stdout] test eval_float_returns_itself ... ok
[INFO] [stdout] test eval_if_false_branch ... ok
[INFO] [stdout] test eval_if_no_alternative_true ... ok
[INFO] [stdout] test eval_if_true_branch ... ok
[INFO] [stdout] test eval_if_no_alternative_false ... ok
[INFO] [stdout] test eval_if_no_args_error ... ok
[INFO] [stdout] test eval_if_non_taken_branch_not_evaluated ... ok
[INFO] [stdout] test eval_integer_returns_itself ... ok
[INFO] [stdout] test eval_if_truthy_empty_list ... ok
[INFO] [stdout] test eval_lambda_bad_param_list_error ... ok
[INFO] [stdout] test eval_if_truthy_zero ... ok
[INFO] [stdout] test eval_lambda_closure_captures_env ... ok
[INFO] [stdout] test eval_lambda_closure_over_closure ... ok
[INFO] [stdout] test eval_lambda_identity ... ok
[INFO] [stdout] test eval_negative_returns_itself ... ok
[INFO] [stdout] test eval_lambda_implicit_begin_in_body ... ok
[INFO] [stdout] test eval_lambda_wrong_arity_error ... ok
[INFO] [stdout] test eval_quote_list ... ok
[INFO] [stdout] test eval_lambda_multi_param ... ok
[INFO] [stdout] test eval_lambda_no_params ... ok
[INFO] [stdout] test eval_quote_empty_list ... ok
[INFO] [stdout] test eval_quote_no_args_error ... ok
[INFO] [stdout] test eval_quote_symbol ... ok
[INFO] [stdout] test eval_quote_nested ... ok
[INFO] [stdout] test eval_quote_too_many_args_error ... ok
[INFO] [stdout] test eval_set_parent_binding ... ok
[INFO] [stdout] test eval_quote_number ... ok
[INFO] [stdout] test eval_set_existing ... ok
[INFO] [stdout] test eval_set_unbound_error ... ok
[INFO] [stdout] test eval_unbound_symbol_returns_error ... ok
[INFO] [stdout] test eval_string_returns_itself ... ok
[INFO] [stdout] test eval_simple_define ... ok
[INFO] [stdout] test eval_e3_5_unwind_protect_normal ... FAILED
[INFO] [stdout] test eval_e3_5_unwind_protect_with_throw ... FAILED
[INFO] [stderr] error: test failed, to rerun pass `--test test_eval`
[INFO] [stdout] test eval_e3_5_unwind_protect_cleanup_order ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- eval_e3_5_unwind_protect_normal stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'eval_e3_5_unwind_protect_normal' (188) panicked at tests/test_eval.rs:1516:49:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Custom { kind: AlreadyExists, error: "Redirect already exists." }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x559da14d30aa - std[fce30c26ee6459b]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x559da14d30aa - std[fce30c26ee6459b]::backtrace_rs::backtrace::trace_unsynchronized::<std[fce30c26ee6459b]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x559da14d30aa - std[fce30c26ee6459b]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x559da14d30aa - <<std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[d17f6591d688f3bd]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x559da14e76da - <core[d17f6591d688f3bd]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x559da14e76da - core[d17f6591d688f3bd]::fmt::write
[INFO] [stdout]    6:     0x559da14d7942 - std[fce30c26ee6459b]::io::default_write_fmt::<alloc[dad09f3a48280dba]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x559da14d7942 - <alloc[dad09f3a48280dba]::vec::Vec<u8> as std[fce30c26ee6459b]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x559da14b1b0f - <std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x559da14b1b0f - std[fce30c26ee6459b]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x559da14cb3a9 - std[fce30c26ee6459b]::panicking::default_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x559da141f11c - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x559da141f11c - test[9653f4f1d2ca1489]::test_main_inner::<test[9653f4f1d2ca1489]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x559da14cb562 - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x559da14cb562 - std[fce30c26ee6459b]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x559da14b1bc8 - std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x559da14a6d29 - std[fce30c26ee6459b]::sys::backtrace::__rust_end_short_backtrace::<std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x559da14b28dd - __rustc[de152adb51f02556]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x559da14e7e5c - core[d17f6591d688f3bd]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x559da14e7bd2 - core[d17f6591d688f3bd]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x559da13fde22 - <core[d17f6591d688f3bd]::result::Result<gag[3b3a2b273837df4c]::buffer::BufferRedirect, std[fce30c26ee6459b]::io::error::Error>>::unwrap
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:1233:23
[INFO] [stdout]   21:     0x559da13fde22 - test_eval[150b43b7c56fd1b9]::eval_e3_5_unwind_protect_normal
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/test_eval.rs:1516:49
[INFO] [stdout]   22:     0x559da13e32b7 - test_eval[150b43b7c56fd1b9]::eval_e3_5_unwind_protect_normal::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/test_eval.rs:1508:37
[INFO] [stdout]   23:     0x559da1411696 - <test_eval[150b43b7c56fd1b9]::eval_e3_5_unwind_protect_normal::{closure#0} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x559da14123fb - <fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x559da14123fb - test[9653f4f1d2ca1489]::__rust_begin_short_backtrace::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x559da141fbeb - test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x559da141fbeb - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x559da141fbeb - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x559da141fbeb - std[fce30c26ee6459b]::panicking::catch_unwind::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x559da141fbeb - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x559da141fbeb - test[9653f4f1d2ca1489]::run_test_in_process
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x559da141fbeb - test[9653f4f1d2ca1489]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x559da1419304 - test[9653f4f1d2ca1489]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x559da1419304 - std[fce30c26ee6459b]::sys::backtrace::__rust_begin_short_backtrace::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x559da14227f2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x559da14227f2 - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x559da14227f2 - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x559da14227f2 - std[fce30c26ee6459b]::panicking::catch_unwind::<(), core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x559da14227f2 - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x559da14227f2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x559da14227f2 - <std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x559da14d29bf - <alloc[dad09f3a48280dba]::boxed::Box<dyn core[d17f6591d688f3bd]::ops::function::FnOnce<(), Output = ()> + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   43:     0x559da14d29bf - <std[fce30c26ee6459b]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x763e88857aa4 - <unknown>
[INFO] [stdout]   45:     0x763e888e4a64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- eval_e3_5_unwind_protect_with_throw stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'eval_e3_5_unwind_protect_with_throw' (189) panicked at tests/test_eval.rs:1535:49:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Custom { kind: AlreadyExists, error: "Redirect already exists." }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x559da14d30aa - std[fce30c26ee6459b]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x559da14d30aa - std[fce30c26ee6459b]::backtrace_rs::backtrace::trace_unsynchronized::<std[fce30c26ee6459b]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x559da14d30aa - std[fce30c26ee6459b]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x559da14d30aa - <<std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[d17f6591d688f3bd]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x559da14e76da - <core[d17f6591d688f3bd]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x559da14e76da - core[d17f6591d688f3bd]::fmt::write
[INFO] [stdout]    6:     0x559da14d7942 - std[fce30c26ee6459b]::io::default_write_fmt::<alloc[dad09f3a48280dba]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x559da14d7942 - <alloc[dad09f3a48280dba]::vec::Vec<u8> as std[fce30c26ee6459b]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x559da14b1b0f - <std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x559da14b1b0f - std[fce30c26ee6459b]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x559da14cb3a9 - std[fce30c26ee6459b]::panicking::default_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x559da141f11c - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x559da141f11c - test[9653f4f1d2ca1489]::test_main_inner::<test[9653f4f1d2ca1489]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x559da14cb562 - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x559da14cb562 - std[fce30c26ee6459b]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x559da14b1bc8 - std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x559da14a6d29 - std[fce30c26ee6459b]::sys::backtrace::__rust_end_short_backtrace::<std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x559da14b28dd - __rustc[de152adb51f02556]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x559da14e7e5c - core[d17f6591d688f3bd]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x559da14e7bd2 - core[d17f6591d688f3bd]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x559da1403b22 - <core[d17f6591d688f3bd]::result::Result<gag[3b3a2b273837df4c]::buffer::BufferRedirect, std[fce30c26ee6459b]::io::error::Error>>::unwrap
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:1233:23
[INFO] [stdout]   21:     0x559da1403b22 - test_eval[150b43b7c56fd1b9]::eval_e3_5_unwind_protect_with_throw
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/test_eval.rs:1535:49
[INFO] [stdout]   22:     0x559da13e3707 - test_eval[150b43b7c56fd1b9]::eval_e3_5_unwind_protect_with_throw::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/test_eval.rs:1527:41
[INFO] [stdout]   23:     0x559da1411c56 - <test_eval[150b43b7c56fd1b9]::eval_e3_5_unwind_protect_with_throw::{closure#0} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x559da14123fb - <fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x559da14123fb - test[9653f4f1d2ca1489]::__rust_begin_short_backtrace::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x559da141fbeb - test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x559da141fbeb - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x559da141fbeb - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x559da141fbeb - std[fce30c26ee6459b]::panicking::catch_unwind::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x559da141fbeb - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x559da141fbeb - test[9653f4f1d2ca1489]::run_test_in_process
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x559da141fbeb - test[9653f4f1d2ca1489]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x559da1419304 - test[9653f4f1d2ca1489]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x559da1419304 - std[fce30c26ee6459b]::sys::backtrace::__rust_begin_short_backtrace::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x559da14227f2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x559da14227f2 - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x559da14227f2 - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x559da14227f2 - std[fce30c26ee6459b]::panicking::catch_unwind::<(), core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x559da14227f2 - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x559da14227f2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x559da14227f2 - <std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x559da14d29bf - <alloc[dad09f3a48280dba]::boxed::Box<dyn core[d17f6591d688f3bd]::ops::function::FnOnce<(), Output = ()> + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   43:     0x559da14d29bf - <std[fce30c26ee6459b]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x763e88857aa4 - <unknown>
[INFO] [stdout]   45:     0x763e888e4a64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- eval_e3_5_unwind_protect_cleanup_order stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'eval_e3_5_unwind_protect_cleanup_order' (187) panicked at tests/test_eval.rs:1561:5:
[INFO] [stdout] assertion `left == right` failed
[INFO] [stdout]   left: "test eval_e3_5_unwind_protect_acceptance ... ok\nab"
[INFO] [stdout]  right: "ab"
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x559da14d30aa - std[fce30c26ee6459b]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x559da14d30aa - std[fce30c26ee6459b]::backtrace_rs::backtrace::trace_unsynchronized::<std[fce30c26ee6459b]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x559da14d30aa - std[fce30c26ee6459b]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x559da14d30aa - <<std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[d17f6591d688f3bd]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x559da14e76da - <core[d17f6591d688f3bd]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x559da14e76da - core[d17f6591d688f3bd]::fmt::write
[INFO] [stdout]    6:     0x559da14d7942 - std[fce30c26ee6459b]::io::default_write_fmt::<alloc[dad09f3a48280dba]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x559da14d7942 - <alloc[dad09f3a48280dba]::vec::Vec<u8> as std[fce30c26ee6459b]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x559da14b1b0f - <std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x559da14b1b0f - std[fce30c26ee6459b]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x559da14cb3a9 - std[fce30c26ee6459b]::panicking::default_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x559da141f11c - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x559da141f11c - test[9653f4f1d2ca1489]::test_main_inner::<test[9653f4f1d2ca1489]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x559da14cb562 - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x559da14cb562 - std[fce30c26ee6459b]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x559da14b1bc8 - std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x559da14a6d29 - std[fce30c26ee6459b]::sys::backtrace::__rust_end_short_backtrace::<std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x559da14b28dd - __rustc[de152adb51f02556]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x559da14e7e5c - core[d17f6591d688f3bd]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x559da14e7d13 - core[d17f6591d688f3bd]::panicking::assert_failed_inner
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:439:17
[INFO] [stdout]   20:     0x559da140b600 - core[d17f6591d688f3bd]::panicking::assert_failed::<alloc[dad09f3a48280dba]::string::String, &str>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:394:5
[INFO] [stdout]   21:     0x559da1406747 - test_eval[150b43b7c56fd1b9]::eval_e3_5_unwind_protect_cleanup_order
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/test_eval.rs:1561:5
[INFO] [stdout]   22:     0x559da13e3917 - test_eval[150b43b7c56fd1b9]::eval_e3_5_unwind_protect_cleanup_order::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/test_eval.rs:1546:44
[INFO] [stdout]   23:     0x559da1411f16 - <test_eval[150b43b7c56fd1b9]::eval_e3_5_unwind_protect_cleanup_order::{closure#0} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x559da14123fb - <fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x559da14123fb - test[9653f4f1d2ca1489]::__rust_begin_short_backtrace::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x559da141fbeb - test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x559da141fbeb - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x559da141fbeb - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x559da141fbeb - std[fce30c26ee6459b]::panicking::catch_unwind::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x559da141fbeb - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x559da141fbeb - test[9653f4f1d2ca1489]::run_test_in_process
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x559da141fbeb - test[9653f4f1d2ca1489]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x559da1419304 - test[9653f4f1d2ca1489]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x559da1419304 - std[fce30c26ee6459b]::sys::backtrace::__rust_begin_short_backtrace::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x559da14227f2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x559da14227f2 - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x559da14227f2 - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x559da14227f2 - std[fce30c26ee6459b]::panicking::catch_unwind::<(), core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x559da14227f2 - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x559da14227f2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x559da14227f2 - <std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x559da14d29bf - <alloc[dad09f3a48280dba]::boxed::Box<dyn core[d17f6591d688f3bd]::ops::function::FnOnce<(), Output = ()> + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   43:     0x559da14d29bf - <std[fce30c26ee6459b]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x763e88857aa4 - <unknown>
[INFO] [stdout]   45:     0x763e888e4a64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     eval_e3_5_unwind_protect_cleanup_order
[INFO] [stdout]     eval_e3_5_unwind_protect_normal
[INFO] [stdout]     eval_e3_5_unwind_protect_with_throw
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 133 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "21ff99163265d730f3a013a6f4355a093e00a4040753b583cedd3465e340e13a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "21ff99163265d730f3a013a6f4355a093e00a4040753b583cedd3465e340e13a", kill_on_drop: false }`
[INFO] [stdout] 21ff99163265d730f3a013a6f4355a093e00a4040753b583cedd3465e340e13a
